**** dT 0.000 * top TEST ../../../../bin/vinyltest/tests/b00037.vtc starting **** top extmacro def pkg_version=trunk **** top extmacro def pkg_branch=trunk **** top extmacro def pwd=/tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest **** top extmacro def date(...) **** top extmacro def string(...) **** top extmacro def localhost=127.0.0.1 **** top extmacro def bad_backend=127.0.0.1:47488 **** top extmacro def listen_addr=127.0.0.1:0 **** top extmacro def bad_ip=192.0.2.255 **** top extmacro def topbuild=/tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub **** top extmacro def topsrc=/tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/../.. **** top macro def testdir=/tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/../../../../bin/vinyltest/tests **** top macro def tmpdir=/tmp/vtest.gcc13_m64_IPv4_uffix/_vtest_tmp/vtc.968046.25e6eb2c **** top macro def vtcid=vtc.968046.25e6eb2c **** dT 0.001 ** top === vtest "Error on multiple Host headers" * top VTEST Error on multiple Host headers ** top === vinyl v1 -vcl {backend be none;} -start **** dT 0.011 ** v1 Launch *** v1 CMD: cd ${pwd} && exec vinyld -d -n /tmp/vtest.gcc13_m64_IPv4_uffix/_vtest_tmp/vtc.968046.25e6eb2c/v1 -i v1 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -a '127.0.0.1:0' -M '127.0.0.1 35531' -P /tmp/vtest.gcc13_m64_IPv4_uffix/_vtest_tmp/vtc.968046.25e6eb2c/v1/vinyld.pid -p vmod_path=/tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/vmod/.libs: *** v1 CMD: cd /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest && exec vinyld -d -n /tmp/vtest.gcc13_m64_IPv4_uffix/_vtest_tmp/vtc.968046.25e6eb2c/v1 -i v1 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -a '127.0.0.1:0' -M '127.0.0.1 35531' -P /tmp/vtest.gcc13_m64_IPv4_uffix/_vtest_tmp/vtc.968046.25e6eb2c/v1/vinyld.pid -p vmod_path=/tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/vmod/.libs: **** dT 0.013 *** v1 PID: 968093 **** dT 0.014 **** v1 macro def v1_pid=968093 **** v1 macro def v1_name=/tmp/vtest.gcc13_m64_IPv4_uffix/_vtest_tmp/vtc.968046.25e6eb2c/v1 **** dT 0.052 *** v1 debug|Warning: mlock() of VSM failed: Not owner (1 *** v1 debug|) **** dT 0.055 *** v1 debug|Debug: Version: vinyl-cache-trunk revision 17ea9216e8091e8bd4b65a2e0b946c92de2125f4 *** v1 debug| *** v1 debug|Debug: *** v1 debug| *** v1 debug|Platform: -jsolaris,-sdefault,-sdefault,-hcritbit *** v1 debug| *** v1 debug|200 292 *** v1 debug|----------------------------- *** v1 debug|Vinyl Cache CLI 1.0 *** v1 debug|----------------------------- *** v1 debug|-jsolaris,-sdefault,-sdefault,-hcritbit *** v1 debug|vinyl-cache-trunk revision 17ea9216e8091e8bd4b65a2e0b946c92de2125f4 *** v1 debug| *** v1 debug|Type 'help' for command list. *** v1 debug|Type 'quit' to close CLI session. *** v1 debug|Type 'start' to launch worker process. *** v1 debug| **** dT 0.154 **** v1 CLIPOLL 1 0x1 0x0 0x0 *** v1 CLI connection fd = 4 *** v1 CLI RX 107 **** v1 CLI RX|qiuvznxhgwxquwabljkgbajszxuycoge **** v1 CLI RX| **** v1 CLI RX|Authentication required. **** v1 CLI TX|auth b58099dce56e74ac9664bf52350ad9160f9e5edb23980dc64fbf00cf1f7b08a5 **** dT 0.155 *** v1 CLI RX 200 **** v1 CLI RX|----------------------------- **** v1 CLI RX|Vinyl Cache CLI 1.0 **** v1 CLI RX|----------------------------- **** v1 CLI RX|-jsolaris,-sdefault,-sdefault,-hcritbit **** v1 CLI RX|vinyl-cache-trunk revision 17ea9216e8091e8bd4b65a2e0b946c92de2125f4 **** v1 CLI RX| **** v1 CLI RX|Type 'help' for command list. **** v1 CLI RX|Type 'quit' to close CLI session. **** v1 CLI RX|Type 'start' to launch worker process. **** v1 CLI TX|vcl.inline vcl1 << %XJEIFLH|)Xspa8P **** v1 CLI TX|vcl 4.1; **** v1 CLI TX|backend be none; **** v1 CLI TX|%XJEIFLH|)Xspa8P **** dT 0.256 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.356 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.456 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.556 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.656 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.756 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.850 *** v1 CLI RX 200 **** v1 CLI RX|Message from VCC-compiler: **** v1 CLI RX|Security: proc_setid missing, uid/gid unchanged **** v1 CLI RX|Message from C-compiler: **** v1 CLI RX|Security: proc_setid missing, uid/gid unchanged **** v1 CLI RX|Message from dlopen: **** v1 CLI RX|Security: proc_setid missing, uid/gid unchanged **** v1 CLI RX|VCL compiled. **** v1 CLI TX|vcl.use vcl1 *** v1 CLI RX 200 **** v1 CLI RX|VCL 'vcl1' now active ** v1 Start **** v1 CLI TX|start **** dT 0.856 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.863 *** v1 debug|Debug: *** v1 debug|Child (968431) Started **** dT 0.892 *** v1 debug|Child launched OK **** dT 0.894 *** v1 CLI RX 200 *** v1 debug|Info: Child (968431) said Child starts *** v1 wait-running **** v1 CLI TX|status *** v1 debug| *** v1 debug|Info: Child (968431) said notice:\tlibumem was already found to be loaded *** v1 debug|Info: Child (968431) said Security: proc_setid missing, uid/gid unchanged *** v1 CLI RX 200 **** v1 CLI RX|Child in state running **** v1 CLI TX|debug.listen_address *** v1 CLI RX 200 **** v1 CLI RX|a0 127.0.0.1 47165 **** v1 CLI TX|debug.xid 1000 *** v1 CLI RX 200 **** v1 CLI RX|XID is 1000 chunk 1 **** v1 CLI TX|debug.listen_address *** v1 CLI RX 200 **** v1 CLI RX|a0 127.0.0.1 47165 ** v1 Listen on 127.0.0.1 47165 **** v1 macro def v1_addr=127.0.0.1 **** v1 macro def v1_port=47165 **** v1 macro def v1_sock=127.0.0.1:47165 **** v1 macro def v1_a0_addr=127.0.0.1 **** v1 macro def v1_a0_port=47165 **** v1 macro def v1_a0_sock=127.0.0.1:47165 **** dT 0.956 **** v1 vsl| 0 CLI - Rd vcl.load "vcl1" vcl_vcl1.1773693376.005645/vgc.so 1auto **** v1 vsl| 0 CLI - Wr 200 52 Loaded "vcl_vcl1.1773693376.005645/vgc.so" as "vcl1" **** v1 vsl| 0 CLI - Rd vcl.use "vcl1" **** v1 vsl| 0 CLI - Wr 200 0 **** v1 vsl| 0 CLI - Rd start **** v1 vsl| 0 Debug - sockopt: Setting SO_LINGER for a0=127.0.0.1:47165 **** v1 vsl| 0 Debug - sockopt: Setting SO_KEEPALIVE for a0=127.0.0.1:47165 **** v1 vsl| 0 Debug - sockopt: Setting SO_SNDTIMEO for a0=127.0.0.1:47165 **** v1 vsl| 0 Debug - sockopt: Setting SO_RCVTIMEO for a0=127.0.0.1:47165 **** v1 vsl| 0 Debug - sockopt: Setting TCP_NODELAY for a0=127.0.0.1:47165 **** v1 vsl| 0 Debug - sockopt: Setting TCP_KEEPIDLE for a0=127.0.0.1:47165 **** v1 vsl| 0 Debug - sockopt: Setting TCP_KEEPCNT for a0=127.0.0.1:47165 **** v1 vsl| 0 Debug - sockopt: Setting TCP_KEEPINTVL for a0=127.0.0.1:47165 **** v1 vsl| 0 CLI - Wr 200 0 **** v1 vsl| 0 CLI - Rd debug.listen_address **** v1 vsl| 0 CLI - Wr 200 19 a0 127.0.0.1 47165 **** v1 vsl| 0 CLI - Rd debug.xid 1000 **** v1 vsl| 0 CLI - Wr 200 19 XID is 1000 chunk 1 **** v1 vsl| 0 CLI - Rd debug.listen_address **** v1 vsl| 0 CLI - Wr 200 19 a0 127.0.0.1 47165 **** dT 0.994 ** top === vinyl v1 -cliok "param.set debug +syncvsl" **** v1 CLI TX|param.set debug +syncvsl *** v1 CLI RX 200 ** v1 CLI 200 ** top === client c1 { ** c1 Starting client **** dT 0.995 ** c1 Waiting for client ** c1 Started on 127.0.0.1:47165 (1 iterations) *** c1 Connect to 127.0.0.1:47165 *** c1 connected fd 14 from 127.0.0.1 35988 to 127.0.0.1:47165 ** c1 === txreq -hdr "Host: foo" -hdr "Host: bar" **** c1 txreq|GET / HTTP/1.1\r **** c1 txreq|Host: foo\r **** c1 txreq|Host: bar\r **** c1 txreq|User-Agent: c1\r **** c1 txreq|\r ** c1 === rxresp **** dT 0.996 **** c1 rxhdr|HTTP/1.1 400 Bad Request\r **** c1 rxhdr|\r **** c1 rxhdrlen = 28 **** c1 http[ 0] |HTTP/1.1 **** c1 http[ 1] |400 **** c1 http[ 2] |Bad Request **** c1 bodylen = 0 ** c1 === expect resp.status == 400 **** c1 EXPECT resp.status (400) == "400" match *** c1 closing fd 14 ** c1 Ending ** top === vinyl v1 -vsl_catchup **** dT 1.056 **** v1 vsl| 1000 Begin c sess 0 HTTP/1 **** v1 vsl| 1000 SessOpen c 127.0.0.1 35988 a0 127.0.0.1 47165 1773693376.845123 17 **** v1 vsl| 1000 Debug c sockopt: Test confirmed SO_KEEPALIVE non heredity for a0=127.0.0.1:47165 **** v1 vsl| 1000 Debug c sockopt: SO_LINGER may be inherited for a0=127.0.0.1:47165 **** v1 vsl| 1000 Debug c sockopt: Setting SO_KEEPALIVE for a0=127.0.0.1:47165 **** v1 vsl| 1000 Debug c sockopt: SO_SNDTIMEO may be inherited for a0=127.0.0.1:47165 **** v1 vsl| 1000 Debug c sockopt: SO_RCVTIMEO may be inherited for a0=127.0.0.1:47165 **** v1 vsl| 1000 Debug c sockopt: TCP_NODELAY may be inherited for a0=127.0.0.1:47165 **** v1 vsl| 1000 Debug c sockopt: TCP_KEEPIDLE may be inherited for a0=127.0.0.1:47165 **** v1 vsl| 1000 Debug c sockopt: TCP_KEEPCNT may be inherited for a0=127.0.0.1:47165 **** v1 vsl| 1000 Debug c sockopt: TCP_KEEPINTVL may be inherited for a0=127.0.0.1:47165 **** v1 vsl| 1001 Begin c req 1000 rxreq **** v1 vsl| 1000 Link c req 1001 rxreq **** v1 vsl| 1001 Timestamp c Start: 1773693376.845520 0.000000 0.000000 **** v1 vsl| 1001 Timestamp c Req: 1773693376.845520 0.000000 0.000000 **** v1 vsl| 1001 VCL_use c vcl1 **** v1 vsl| 1001 ReqStart c 127.0.0.1 35988 a0 **** v1 vsl| 1001 ReqMethod c GET **** v1 vsl| 1001 ReqURL c / **** v1 vsl| 1001 ReqProtocol c HTTP/1.1 **** v1 vsl| 1001 ReqHeader c Host: foo **** v1 vsl| 1001 ReqHeader c Host: bar **** v1 vsl| 1001 ReqHeader c User-Agent: c1 **** v1 vsl| 1001 BogoHeader c Multiple Host: headers **** v1 vsl| 1001 RespProtocol c HTTP/1.1 **** v1 vsl| 1001 RespStatus c 400 **** v1 vsl| 1001 RespReason c Bad Request **** v1 vsl| 1001 ReqAcct c 56 0 56 28 0 28 **** v1 vsl| 1001 End c **** v1 vsl| 1000 SessClose c RX_BAD 0.001 **** v1 vsl| 1000 End c **** dT 1.096 ** top === vinyl v1 -expect client_req_400 == 1 **** dT 1.098 ** v1 as expected: client_req_400 (1) == 1 (1) ** top === vinyl v1 -expect sc_rx_bad == 1 ** v1 as expected: sc_rx_bad (1) == 1 (1) ** top === client c1 { ** c1 Starting client ** c1 Waiting for client ** c1 Started on 127.0.0.1:47165 (1 iterations) *** c1 Connect to 127.0.0.1:47165 *** c1 connected fd 16 from 127.0.0.1 53839 to 127.0.0.1:47165 ** c1 === txreq -method POST -hdr "Content-Length: 12" -hdr "Content-L... **** c1 txreq|POST / HTTP/1.1\r **** c1 txreq|Content-Length: 12\r **** c1 txreq|Content-Length: 12\r **** c1 txreq|Host: 127.0.0.1\r **** c1 txreq|User-Agent: c1\r **** c1 txreq|\r **** c1 txreq|!"#$%&'()*+ ** c1 === rxresp **** c1 rxhdr|HTTP/1.1 400 Bad Request\r **** c1 rxhdr|\r **** c1 rxhdrlen = 28 **** c1 http[ 0] |HTTP/1.1 **** c1 http[ 1] |400 **** c1 http[ 2] |Bad Request **** c1 bodylen = 0 ** c1 === expect resp.status == 400 **** c1 EXPECT resp.status (400) == "400" match *** c1 closing fd 16 **** dT 1.099 ** c1 Ending ** top === vinyl v1 -vsl_catchup **** dT 1.156 **** v1 vsl| 1002 Begin c sess 0 HTTP/1 **** v1 vsl| 1002 SessOpen c 127.0.0.1 53839 a0 127.0.0.1 47165 1773693376.948344 18 **** v1 vsl| 1002 Debug c sockopt: SO_LINGER may be inherited for a0=127.0.0.1:47165 **** v1 vsl| 1002 Debug c sockopt: Setting SO_KEEPALIVE for a0=127.0.0.1:47165 **** v1 vsl| 1002 Debug c sockopt: SO_SNDTIMEO may be inherited for a0=127.0.0.1:47165 **** v1 vsl| 1002 Debug c sockopt: SO_RCVTIMEO may be inherited for a0=127.0.0.1:47165 **** v1 vsl| 1002 Debug c sockopt: TCP_NODELAY may be inherited for a0=127.0.0.1:47165 **** v1 vsl| 1002 Debug c sockopt: TCP_KEEPIDLE may be inherited for a0=127.0.0.1:47165 **** v1 vsl| 1002 Debug c sockopt: TCP_KEEPCNT may be inherited for a0=127.0.0.1:47165 **** v1 vsl| 1002 Debug c sockopt: TCP_KEEPINTVL may be inherited for a0=127.0.0.1:47165 **** v1 vsl| 1003 Begin c req 1002 rxreq **** v1 vsl| 1002 Link c req 1003 rxreq **** v1 vsl| 1003 Timestamp c Start: 1773693376.948441 0.000000 0.000000 **** v1 vsl| 1003 Timestamp c Req: 1773693376.948441 0.000000 0.000000 **** dT 1.157 **** v1 vsl| 1003 VCL_use c vcl1 **** v1 vsl| 1003 ReqStart c 127.0.0.1 53839 a0 **** v1 vsl| 1003 ReqMethod c POST **** v1 vsl| 1003 ReqURL c / **** v1 vsl| 1003 ReqProtocol c HTTP/1.1 **** v1 vsl| 1003 ReqHeader c Content-Length: 12 **** v1 vsl| 1003 ReqHeader c Content-Length: 12 **** v1 vsl| 1003 ReqHeader c Host: 127.0.0.1 **** v1 vsl| 1003 ReqHeader c User-Agent: c1 **** v1 vsl| 1003 BogoHeader c Multiple Content-Length: headers **** v1 vsl| 1003 RespProtocol c HTTP/1.1 **** v1 vsl| 1003 RespStatus c 400 **** v1 vsl| 1003 RespReason c Bad Request **** v1 vsl| 1003 ReqAcct c 92 0 92 28 0 28 **** v1 vsl| 1003 End c **** v1 vsl| 1002 SessClose c RX_BAD 0.000 **** v1 vsl| 1002 End c **** dT 1.199 ** top === vinyl v1 -expect client_req_400 == 2 ** v1 as expected: client_req_400 (2) == 2 (2) ** top === vinyl v1 -expect sc_rx_bad == 2 ** v1 as expected: sc_rx_bad (2) == 2 (2) ** top === vinyl v1 -cliok "param.set feature +http2" **** v1 CLI TX|param.set feature +http2 *** v1 CLI RX 200 ** v1 CLI 200 ** top === client c2 { ** c2 Starting client ** c2 Waiting for client ** c2 Started on 127.0.0.1:47165 (1 iterations) *** c2 Connect to 127.0.0.1:47165 *** c2 connected fd 16 from 127.0.0.1 55872 to 127.0.0.1:47165 ** c2 === stream 7 { **** c2 Not in H/2 mode, do what's needed ** c2 === txpri **** c2 txpri|PRI * HTTP/2.0\r **** c2 txpri|\r **** c2 txpri|SM\r **** c2 txpri|\r **** dT 1.209 ** c2 === stream 0 { ** c2 Starting stream 0 (fffffc7feecf8010) **** dT 1.210 ---- c2.0 Assert error in stream_start(), ../../../../bin/vinyltest/vtest2/src/vtc_http2.c line 2738: Condition(pthread_create(&s->tp, ((void *)0), stream_thread, s) failed) not true. Errno=11 Resource temporarily unavailable Backtrace: fffffc60a41cfe06: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/lib/libvinylapi/.libs/libvinylapi.so.3.1.0'vbt_execinfo+0x36 [0xfffffc60a41cfe06] fffffc60a41d0065: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/lib/libvinylapi/.libs/libvinylapi.so.3.1.0'VBT_format+0x2c [0xfffffc60a41d0065] fffffc60a41d00d5: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/lib/libvinylapi/.libs/libvinylapi.so.3.1.0'VBT_dump+0x6b [0xfffffc60a41d00d5] 439ddc: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'vtc_log_VAS_Fail+0x78 [0x439ddc] fffffc60a41cf293: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/lib/libvinylapi/.libs/libvinylapi.so.3.1.0'VAS_Fail+0x4f [0xfffffc60a41cf293] 437dfd: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'stream_start+0xe8 [0x437dfd] 437fd6: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'stream_run+0x18 [0x437fd6] 43819a: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'cmd_stream+0x1b5 [0x43819a] 42c7de: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'cmd_http_stream+0x109 [0x42c7de] 41bcf1: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'parse_string+0xac3 [0x41bcf1] 42c7c7: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'cmd_http_stream+0xf2 [0x42c7c7] 41bcf1: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'parse_string+0xac3 [0x41bcf1] 42cef5: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'http_process+0x43b [0x42cef5] 4494a8: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'sess_process+0xa9 [0x4494a8] 44971e: /tmp/vtest.gcc13_m64_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'sess_thread+0x26f [0x44971e] fffffc7fef2556e7: /lib/amd64/libc.so.1'_thrp_setup+0x77 [0xfffffc7fef2556e7] fffffc7fef255a30: /lib/amd64/libc.so.1'_lwp_start+0x0 [0xfffffc7fef255a30] * top Aborting execution, test failed * top RESETTING after ../../../../bin/vinyltest/tests/b00037.vtc ** v1 Wait **** v1 CLI TX|panic.show *** v1 CLI RX 300 **** v1 CLI RX|Child has not panicked or panic has been cleared *** v1 debug|Info: manager stopping child *** v1 debug|Debug: Stopping Child **** dT 1.257 **** v1 vsl| 1004 Begin c sess 0 HTTP/1 **** v1 vsl| 1004 SessOpen c 127.0.0.1 55872 a0 127.0.0.1 47165 1773693377.049409 19 **** v1 vsl| 1004 Debug c sockopt: SO_LINGER may be inherited for a0=127.0.0.1:47165 **** v1 vsl| 1004 Debug c sockopt: Setting SO_KEEPALIVE for a0=127.0.0.1:47165 **** v1 vsl| 1004 Debug c sockopt: SO_SNDTIMEO may be inherited for a0=127.0.0.1:47165 **** v1 vsl| 1004 Debug c sockopt: SO_RCVTIMEO may be inherited for a0=127.0.0.1:47165 **** v1 vsl| 1004 Debug c sockopt: TCP_NODELAY may be inherited for a0=127.0.0.1:47165 **** v1 vsl| 1004 Debug c sockopt: TCP_KEEPIDLE may be inherited for a0=127.0.0.1:47165 **** v1 vsl| 1004 Debug c sockopt: TCP_KEEPCNT may be inherited for a0=127.0.0.1:47165 **** v1 vsl| 1004 Debug c sockopt: TCP_KEEPINTVL may be inherited for a0=127.0.0.1:47165 **** v1 vsl| 1004 Debug c H2 Prior Knowledge Upgrade **** v1 vsl| 1004 Debug c H2: Got pu PRISM **** v1 vsl| 1004 Debug c H2: HTC eof (Unexpected end of input) frame=complete goaway=0 **** v1 vsl| 1004 Debug c H2 CLEANUP H2CE_NO_ERROR **** v1 vsl| 1004 ReqAcct c 0 0 0 18 26 44 **** v1 vsl| 1004 SessClose c REM_CLOSE 0.011 **** v1 vsl| 1004 End c **** v1 vsl| 0 CLI - EOF on CLI connection, worker stops **** dT 2.011 *** v1 debug|Info: Child (968431) said Child dies *** v1 debug|Info: Child (968431) ended *** v1 debug|Debug: *** v1 debug|Child cleanup complete *** v1 debug| *** v1 debug|Info: *** v1 debug|manager dies *** v1 debug| **** v1 STDOUT EOF **** dT 2.057 ** v1 WAIT4 pid=968093 status=0x0000 (user 0.601000 sys 0.152000) **** dT 2.058 * top TEST ../../../../bin/vinyltest/tests/b00037.vtc FAILED # top TEST ../../../../bin/vinyltest/tests/b00037.vtc FAILED (2.063) exit=2 FAIL tests/b00037.vtc (exit status: 2)